[INFO] cloning repository https://github.com/simioliolio/greg
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/simioliolio/greg" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsimioliolio%2Fgreg", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsimioliolio%2Fgreg'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 91aefbdf2f1c2169d47f06798ac7205e05d47587
[INFO] testing simioliolio/greg against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsimioliolio%2Fgreg" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/simioliolio/greg
[INFO] finished tweaking git repo https://github.com/simioliolio/greg
[INFO] tweaked toml for git repo https://github.com/simioliolio/greg written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/simioliolio/greg on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/simioliolio/greg already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded windows-interface v0.56.0
[INFO] [stderr]   Downloaded midir v0.10.4
[INFO] [stderr]   Downloaded windows-result v0.1.2
[INFO] [stderr]   Downloaded windows-core v0.56.0
[INFO] [stderr]   Downloaded extended v0.1.0
[INFO] [stderr]   Downloaded symphonia-codec-alac v0.5.5
[INFO] [stderr]   Downloaded symphonia-codec-adpcm v0.5.5
[INFO] [stderr]   Downloaded coremidi-sys v3.2.0
[INFO] [stderr]   Downloaded windows-implement v0.56.0
[INFO] [stderr]   Downloaded symphonia v0.5.5
[INFO] [stderr]   Downloaded transpose v0.2.3
[INFO] [stderr]   Downloaded typeid v1.0.3
[INFO] [stderr]   Downloaded coreaudio-sys v0.2.17
[INFO] [stderr]   Downloaded primal-check v0.3.4
[INFO] [stderr]   Downloaded symphonia-utils-xiph v0.5.5
[INFO] [stderr]   Downloaded symphonia-codec-pcm v0.5.5
[INFO] [stderr]   Downloaded socks v0.3.4
[INFO] [stderr]   Downloaded hmac-sha256 v1.1.14
[INFO] [stderr]   Downloaded rten-base v0.24.0
[INFO] [stderr]   Downloaded dasp_sample v0.11.0
[INFO] [stderr]   Downloaded symphonia-bundle-flac v0.5.5
[INFO] [stderr]   Downloaded symphonia-format-caf v0.5.5
[INFO] [stderr]   Downloaded toml_datetime v1.1.0+spec-1.1.0
[INFO] [stderr]   Downloaded symphonia-format-ogg v0.5.5
[INFO] [stderr]   Downloaded symphonia-format-mkv v0.5.5
[INFO] [stderr]   Downloaded symphonia-codec-aac v0.5.5
[INFO] [stderr]   Downloaded realfft v3.5.0
[INFO] [stderr]   Downloaded strength_reduce v0.2.4
[INFO] [stderr]   Downloaded oboe v0.6.1
[INFO] [stderr]   Downloaded symphonia-metadata v0.5.5
[INFO] [stderr]   Downloaded symphonia-codec-vorbis v0.5.5
[INFO] [stderr]   Downloaded symphonia-format-riff v0.5.5
[INFO] [stderr]   Downloaded flatbuffers v24.12.23
[INFO] [stderr]   Downloaded native-tls v0.2.18
[INFO] [stderr]   Downloaded security-framework-sys v2.17.0
[INFO] [stderr]   Downloaded rten-vecmath v0.24.0
[INFO] [stderr]   Downloaded rten-shape-inference v0.24.0
[INFO] [stderr]   Downloaded windows-core v0.54.0
[INFO] [stderr]   Downloaded toml_parser v1.1.0+spec-1.1.0
[INFO] [stderr]   Downloaded beat-this v0.3.0
[INFO] [stderr]   Downloaded hound v3.5.1
[INFO] [stderr]   Downloaded coremidi v0.9.0
[INFO] [stderr]   Downloaded coreaudio-rs v0.11.3
[INFO] [stderr]   Downloaded rten-simd v0.24.0
[INFO] [stderr]   Downloaded rten-model-file v0.24.0
[INFO] [stderr]   Downloaded rustls-pki-types v1.14.1
[INFO] [stderr]   Downloaded schannel v0.1.29
[INFO] [stderr]   Downloaded ort-sys v2.0.0-rc.11
[INFO] [stderr]   Downloaded rubato v0.14.1
[INFO] [stderr]   Downloaded symphonia-format-isomp4 v0.5.5
[INFO] [stderr]   Downloaded symphonia-bundle-mp3 v0.5.5
[INFO] [stderr]   Downloaded portable-atomic-util v0.2.7
[INFO] [stderr]   Downloaded toml_edit v0.25.8+spec-1.1.0
[INFO] [stderr]   Downloaded rten-tensor v0.24.0
[INFO] [stderr]   Downloaded rten-gemm v0.24.0
[INFO] [stderr]   Downloaded openssl-sys v0.9.116
[INFO] [stderr]   Downloaded alsa v0.9.1
[INFO] [stderr]   Downloaded symphonia-core v0.5.5
[INFO] [stderr]   Downloaded security-framework v3.7.0
[INFO] [stderr]   Downloaded cpal v0.15.3
[INFO] [stderr]   Downloaded webpki-root-certs v1.0.7
[INFO] [stderr]   Downloaded oboe-sys v0.6.1
[INFO] [stderr]   Downloaded ort v2.0.0-rc.11
[INFO] [stderr]   Downloaded winnow v1.0.0
[INFO] [stderr]   Downloaded rten-onnx v0.24.0
[INFO] [stderr]   Downloaded ndarray v0.17.2
[INFO] [stderr]   Downloaded lzma-rust2 v0.15.8
[INFO] [stderr]   Downloaded rustfft v6.4.1
[INFO] [stderr]   Downloaded openssl v0.10.80
[INFO] [stderr]   Downloaded rten v0.24.0
[INFO] [stderr]   Downloaded windows v0.56.0
[INFO] [stderr]   Downloaded windows v0.54.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e721633e2edd61f4ed698c15dbad431f6d6f0c2525c866f0e3af07a6780e0d2d
[INFO] running `Command { std: "docker" "start" "-a" "e721633e2edd61f4ed698c15dbad431f6d6f0c2525c866f0e3af07a6780e0d2d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e721633e2edd61f4ed698c15dbad431f6d6f0c2525c866f0e3af07a6780e0d2d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e721633e2edd61f4ed698c15dbad431f6d6f0c2525c866f0e3af07a6780e0d2d", kill_on_drop: false }`
[INFO] [stdout] e721633e2edd61f4ed698c15dbad431f6d6f0c2525c866f0e3af07a6780e0d2d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bc746b9cb2ea176157abad956f9dc3f04b2803f11787508689a800cc0e8ae7a9
[INFO] running `Command { std: "docker" "start" "-a" "bc746b9cb2ea176157abad956f9dc3f04b2803f11787508689a800cc0e8ae7a9", kill_on_drop: false }`
[INFO] [stderr]    Compiling bytemuck v1.25.0
[INFO] [stderr]    Compiling encoding_rs v0.8.35
[INFO] [stderr]    Compiling cc v1.2.57
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling httparse v1.10.1
[INFO] [stderr]    Compiling foreign-types-shared v0.1.1
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling openssl v0.10.80
[INFO] [stderr]    Compiling native-tls v0.2.18
[INFO] [stderr]    Compiling base64ct v1.8.3
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling openssl-macros v0.1.1
[INFO] [stderr]    Compiling num-complex v0.4.6
[INFO] [stderr]    Compiling foreign-types v0.3.2
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling openssl-probe v0.2.1
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling rustls-pki-types v1.14.1
[INFO] [stderr]    Compiling typeid v1.0.3
[INFO] [stderr]    Compiling strength_reduce v0.2.4
[INFO] [stderr]    Compiling pem-rfc7468 v1.0.0
[INFO] [stderr]    Compiling socks v0.3.4
[INFO] [stderr]    Compiling symphonia-core v0.5.5
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling utf8-zero v0.8.1
[INFO] [stderr]    Compiling primal-check v0.3.4
[INFO] [stderr]    Compiling flatbuffers v24.12.23
[INFO] [stderr]    Compiling der v0.8.0
[INFO] [stderr]    Compiling transpose v0.2.3
[INFO] [stderr]    Compiling matrixmultiply v0.3.10
[INFO] [stderr]    Compiling alsa-sys v0.3.1
[INFO] [stderr]    Compiling hmac-sha256 v1.1.14
[INFO] [stderr]    Compiling lzma-rust2 v0.15.8
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling rawpointer v0.2.1
[INFO] [stderr]    Compiling rustfft v6.4.1
[INFO] [stderr]    Compiling rten-simd v0.24.0
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling webpki-root-certs v1.0.7
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling extended v0.1.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling http v1.4.0
[INFO] [stderr]    Compiling alsa v0.9.1
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling num_cpus v1.17.0
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling ndarray v0.17.2
[INFO] [stderr]    Compiling libloading v0.9.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling rustc-hash v2.1.1
[INFO] [stderr]    Compiling rten-onnx v0.24.0
[INFO] [stderr]    Compiling cpal v0.15.3
[INFO] [stderr]    Compiling signal-hook-mio v0.2.5
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling dasp_sample v0.11.0
[INFO] [stderr]    Compiling hound v3.5.1
[INFO] [stderr]    Compiling openssl-sys v0.9.116
[INFO] [stderr]    Compiling symphonia-metadata v0.5.5
[INFO] [stderr]    Compiling symphonia-codec-alac v0.5.5
[INFO] [stderr]    Compiling symphonia-codec-adpcm v0.5.5
[INFO] [stderr]    Compiling rten-base v0.24.0
[INFO] [stderr]    Compiling ureq-proto v0.6.0
[INFO] [stderr]    Compiling symphonia-codec-pcm v0.5.5
[INFO] [stderr]    Compiling symphonia-codec-aac v0.5.5
[INFO] [stderr]    Compiling crossterm v0.28.1
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling symphonia-utils-xiph v0.5.5
[INFO] [stderr]    Compiling rten-tensor v0.24.0
[INFO] [stderr]    Compiling symphonia-bundle-mp3 v0.5.5
[INFO] [stderr]    Compiling symphonia-bundle-flac v0.5.5
[INFO] [stderr]    Compiling symphonia-format-mkv v0.5.5
[INFO] [stderr]    Compiling symphonia-codec-vorbis v0.5.5
[INFO] [stderr]    Compiling symphonia-format-ogg v0.5.5
[INFO] [stderr]    Compiling symphonia-format-isomp4 v0.5.5
[INFO] [stderr]    Compiling symphonia-format-riff v0.5.5
[INFO] [stderr]    Compiling symphonia-format-caf v0.5.5
[INFO] [stderr]    Compiling rten-model-file v0.24.0
[INFO] [stderr]    Compiling rten-vecmath v0.24.0
[INFO] [stderr]    Compiling midir v0.10.4
[INFO] [stderr]    Compiling rten-gemm v0.24.0
[INFO] [stderr]    Compiling rten-shape-inference v0.24.0
[INFO] [stderr]    Compiling symphonia v0.5.5
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling realfft v3.5.0
[INFO] [stderr]    Compiling rubato v0.14.1
[INFO] [stderr]    Compiling rten v0.24.0
[INFO] [stderr]    Compiling ureq v3.3.0
[INFO] [stderr]    Compiling ort-sys v2.0.0-rc.11
[INFO] [stderr]    Compiling ort v2.0.0-rc.11
[INFO] [stderr]    Compiling beat-this v0.3.0
[INFO] [stderr]    Compiling greg v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 57.79s
[INFO] running `Command { std: "docker" "inspect" "bc746b9cb2ea176157abad956f9dc3f04b2803f11787508689a800cc0e8ae7a9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bc746b9cb2ea176157abad956f9dc3f04b2803f11787508689a800cc0e8ae7a9", kill_on_drop: false }`
[INFO] [stdout] bc746b9cb2ea176157abad956f9dc3f04b2803f11787508689a800cc0e8ae7a9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f128f95f7f502657128f992efee09d6af84e73affa048b6e82ac4d55fe242c1c
[INFO] running `Command { std: "docker" "start" "-a" "f128f95f7f502657128f992efee09d6af84e73affa048b6e82ac4d55fe242c1c", kill_on_drop: false }`
[INFO] [stderr]    Compiling greg v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.99s
[INFO] running `Command { std: "docker" "inspect" "f128f95f7f502657128f992efee09d6af84e73affa048b6e82ac4d55fe242c1c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f128f95f7f502657128f992efee09d6af84e73affa048b6e82ac4d55fe242c1c", kill_on_drop: false }`
[INFO] [stdout] f128f95f7f502657128f992efee09d6af84e73affa048b6e82ac4d55fe242c1c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d4fbe0a8a057fb5f318b6c613ad507941e9c8bfb425d4df640aa693ef27c5a1b
[INFO] running `Command { std: "docker" "start" "-a" "d4fbe0a8a057fb5f318b6c613ad507941e9c8bfb425d4df640aa693ef27c5a1b", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.26s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/greg-9077d8b04de44f46)
[INFO] [stdout] 
[INFO] [stdout] running 42 tests
[INFO] [stdout] test beat_confirmation::tests::three_runs_within_tolerance_confirms ... ok
[INFO] [stdout] test beat_confirmation::tests::two_runs_not_enough ... ok
[INFO] [stdout] test beat_confirmation::tests::beats_beyond_tolerance_not_merged ... ok
[INFO] [stdout] test beat_detector::tests::beat_this_detector_invalid_path ... ok
[INFO] [stdout] test beat_detector::tests::stub_detector_120bpm ... ok
[INFO] [stdout] test beat_detector::tests::stub_detector_empty_buffer ... ok
[INFO] [stdout] test clock::tests::clock_state_bpm_operations ... ok
[INFO] [stdout] test clock::tests::pulse_interval_120bpm ... ok
[INFO] [stdout] test clock::tests::pulse_interval_various_bpms ... ok
[INFO] [stdout] test beat_confirmation::tests::empty_candidates_handled ... ok
[INFO] [stdout] test beat_confirmation::tests::oldest_run_dropped_after_max ... ok
[INFO] [stdout] test pll::tests::phase_correction_activates_in_locked ... ok
[INFO] [stdout] test pll::tests::state_transitions_idle_to_rate_locking ... ok
[INFO] [stdout] test ring_buffer::tests::empty_read ... ok
[INFO] [stdout] test ring_buffer::tests::read_latest_fewer_than_written ... ok
[INFO] [stdout] test pll::tests::rate_correction_nudges_toward_detected ... ok
[INFO] [stdout] test pll::tests::detected_bpm_from_regular_beats ... ok
[INFO] [stdout] test pll::tests::detected_bpm_insufficient_beats ... ok
[INFO] [stdout] test ring_buffer::tests::read_more_than_written_returns_available ... ok
[INFO] [stdout] test tap_tempo::tests::timeout_resets ... ok
[INFO] [stdout] test pll::tests::state_transitions_rate_locking_to_locked ... ok
[INFO] [stdout] test ring_buffer::tests::write_and_read_back ... ok
[INFO] [stdout] test tap_tempo::tests::two_taps_not_enough ... ok
[INFO] [stdout] test time_source::tests::synthetic_advance_by ... ok
[INFO] [stdout] test tap_tempo::tests::three_taps_at_120bpm ... ok
[INFO] [stdout] test time_source::tests::synthetic_now_returns_base ... ok
[INFO] [stdout] test time_source::tests::synthetic_sleep_until_past_returns_immediately ... ok
[INFO] [stdout] test time_source::tests::now_returns_increasing_values ... ok
[INFO] [stdout] test state::tests::initial_state_is_idle ... ok
[INFO] [stdout] test time_source::tests::sleep_until_past_returns_immediately ... ok
[INFO] [stdout] test state::tests::transitions ... ok
[INFO] [stdout] test tap_tempo::tests::fourth_tap_updates_bpm ... ok
[INFO] [stdout] test ring_buffer::tests::wraparound ... ok
[INFO] [stdout] test state::tests::midi_active_only_when_not_idle ... ok
[INFO] [stdout] test pll::tests::tap_constraint_clamps_detected_bpm ... ok
[INFO] [stdout] test click_track::tests::click_track_120bpm_has_beats ... ok
[INFO] [stdout] test time_source::tests::synthetic_sleep_until_future_blocks_then_wakes ... ok
[INFO] [stdout] test ring_buffer::tests::concurrent_write_and_read ... ok
[INFO] [stderr] ALSA lib seq_hw.c:528:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[INFO] [stdout] test click_track::tests::click_track_wav_roundtrip ... ok
[INFO] [stdout] test midi::tests::midi_output_creation ... ok
[INFO] [stdout] test time_source::tests::sleep_until_future_sleeps ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/greg-1eea666f6a154d06)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/acceptance.rs (/opt/rustwide/target/debug/deps/acceptance-191dd656f41abaaf)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test convergence_120bpm ... FAILED
[INFO] [stdout] test convergence_60bpm ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- convergence_120bpm stdout ----
[INFO] [stdout] [analysis] run 0: 9 candidates, compute 0.000s (avg 0.000s)
[INFO] [stdout] [analysis] run 1: 9 candidates, compute 0.000s (avg 0.000s)
[INFO] [stdout] [analysis] run 2: 9 candidates, compute 0.000s (avg 0.000s)
[INFO] [stdout] [analysis] run 3: 9 candidates, compute 0.000s (avg 0.000s)
[INFO] [stdout] [analysis] run 4: 9 candidates, compute 0.000s (avg 0.000s)
[INFO] [stdout] 
[INFO] [stdout] thread 'convergence_120bpm' (66) panicked at tests/acceptance.rs:82:5:
[INFO] [stdout] expected at least 5 confirmed beats, got 0
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5e89260dc3f8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5e89260dc3f8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5e89260dc3f8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5e89260dc3f8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5e89260f064a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5e89260f064a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5e89260e0c62 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5e89260e0c62 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5e89260bb326 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5e89260bb326 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5e89260d4849 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5e892603d65c - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5e892603d65c - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5e89260d4a02 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5e89260d4a02 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5e89260bb3e2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5e89260b05e9 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5e89260bc0fd - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5e89260f0ddc - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5e892602ea43 - acceptance[c8c8b2a07b55024f]::convergence_120bpm
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/acceptance.rs:82:5
[INFO] [stdout]   20:     0x5e892602dad7 - acceptance[c8c8b2a07b55024f]::convergence_120bpm::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/acceptance.rs:79:24
[INFO] [stdout]   21:     0x5e8926030656 - <acceptance[c8c8b2a07b55024f]::convergence_120bpm::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5e892603073b - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5e892603073b - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   24:     0x5e892603dfb5 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   25:     0x5e892603dfb5 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x5e892603dfb5 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x5e892603dfb5 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x5e892603dfb5 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x5e892603dfb5 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   30:     0x5e892603dfb5 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   31:     0x5e8926038b14 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   32:     0x5e8926038b14 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x5e8926041032 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x5e8926041032 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x5e8926041032 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x5e8926041032 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x5e8926041032 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x5e8926041032 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x5e8926041032 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x5e89260dbd5f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   41:     0x5e89260dbd5f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   42:     0x741de7c3eaa4 - <unknown>
[INFO] [stdout]   43:     0x741de7ccba64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     convergence_120bpm
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.45s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test acceptance`
[INFO] running `Command { std: "docker" "inspect" "d4fbe0a8a057fb5f318b6c613ad507941e9c8bfb425d4df640aa693ef27c5a1b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d4fbe0a8a057fb5f318b6c613ad507941e9c8bfb425d4df640aa693ef27c5a1b", kill_on_drop: false }`
[INFO] [stdout] d4fbe0a8a057fb5f318b6c613ad507941e9c8bfb425d4df640aa693ef27c5a1b
